﻿2026-09-09T05:21:52.3316561Z ##[group]Run bash .github/bench/wrap.sh twohost-web-b -- cargo test -p spt-daemon --test twohost_web -- --nocapture
2026-09-09T05:21:52.3317224Z [36;1mbash .github/bench/wrap.sh twohost-web-b -- cargo test -p spt-daemon --test twohost_web -- --nocapture[0m
2026-09-09T05:21:52.3329918Z shell: /usr/bin/bash --noprofile --norc -e -o pipefail {0}
2026-09-09T05:21:52.3330194Z env:
2026-09-09T05:21:52.3330494Z   SPT_CI_TEST_TMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34310511612-1-DDBSE3
2026-09-09T05:21:52.3330947Z   TEMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34310511612-1-DDBSE3
2026-09-09T05:21:52.3331362Z   TMP: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34310511612-1-DDBSE3
2026-09-09T05:21:52.3331780Z   TMPDIR: /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34310511612-1-DDBSE3
2026-09-09T05:21:52.3332107Z   SPT_TWO_HOST: 1
2026-09-09T05:21:52.3332293Z   SPT_TWO_HOST_ROLE: b
2026-09-09T05:21:52.3332499Z   SPT_TWO_HOST_SECRET: rig-twohost-ci
2026-09-09T05:21:52.3332770Z   SPT_TWO_HOST_PEER_IP: 100.68.35.65
2026-09-09T05:21:52.3332998Z   SPT_TWO_HOST_WAIT_SECS: 900
2026-09-09T05:21:52.3333208Z ##[endgroup]
2026-09-09T05:21:52.5791238Z    Compiling spt-daemon v0.68.0 (/home/reavus/actions-runner/_work/spt-bs-core/spt-bs-core/crates/spt-daemon)
2026-09-09T05:21:54.0816535Z     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.72s
2026-09-09T05:21:54.1002232Z      Running tests/twohost_web.rs (target/debug/deps/twohost_web-faf8108c04874cf8)
2026-09-09T05:21:54.1034623Z 
2026-09-09T05:21:54.1035062Z running 5 tests
2026-09-09T05:21:54.1038168Z test two_host_web_helper_role_a ... ok
2026-09-09T05:21:54.1038712Z test two_host_web_fetch_role_a ... ok
2026-09-09T05:21:54.1039085Z test two_host_web_deny_role_a ... ok
2026-09-09T05:21:54.1039376Z test two_host_web_range_role_a ... ok
2026-09-09T05:21:54.1042801Z TWOHOST-WEB role B: node 9bbcee970607c7454b0baf8b38b032ccdf1a36de7ef04d614158fecd1f08b612 serving on udp 7481
2026-09-09T05:21:54.1187954Z ACCESS_STORE_MINTED /home/reavus/actions-runner/_work/_temp/spt-test-tmp-34310511612-1-DDBSE3/.tmp159Qst/identity/trust/access.json — no access store was present at first load in this process, so an EMPTY baseline was written (everything open that the chain leaves open). If this node had access rules, they are GONE: the store was removed while the daemon was down, and this mint replaced it. Re-apply them (`spt endpoint access ...`); a restart cannot tell a wipe from a first boot.
2026-09-09T05:21:54.1331171Z TWOHOST-WEB role B: audience row merged, verdicts [Merged(Inserted)]
2026-09-09T05:21:54.1336572Z CONN_LIFECYCLE: conn=1 role=brain wall_ms=1788931314133 mono_ms=0 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:21:54.1484784Z PAIR_MEET_UP:twohost step=59631043 id=e0b71222965552cc8fa5c9ec9778c07ea11557bb4bd3c6f4602456a12d9f0708 addr=EndpointAddr { id: PublicKey(e0b71222965552cc8fa5c9ec9778c07ea11557bb4bd3c6f4602456a12d9f0708), addrs: {Ip(100.98.197.12:46822), Ip(192.168.1.168:46822)} }
2026-09-09T05:22:00.1519401Z PAIR_MEET_UP:twohost step=59631044 id=63ccc98cf91b0d65528b2743deda083e50edea2e8dbaddc4018e89889fe6ef11 addr=EndpointAddr { id: PublicKey(63ccc98cf91b0d65528b2743deda083e50edea2e8dbaddc4018e89889fe6ef11), addrs: {Ip(100.98.197.12:55624), Ip(192.168.1.168:55624)} }
2026-09-09T05:22:01.0369218Z CONN_LIFECYCLE: conn=3 role=brain wall_ms=1788931321036 mono_ms=6903 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0369999Z CONN_LIFECYCLE: conn=5 role=brain wall_ms=1788931321036 mono_ms=6903 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0370964Z CONN_LIFECYCLE: conn=4 role=brain wall_ms=1788931321036 mono_ms=6903 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0371743Z CONN_LIFECYCLE: conn=3 role=brain wall_ms=1788931321036 mono_ms=6903 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0372625Z CONN_LIFECYCLE: conn=5 role=brain wall_ms=1788931321036 mono_ms=6903 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0373828Z CONN_LIFECYCLE: conn=4 role=brain wall_ms=1788931321036 mono_ms=6903 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0374913Z ACCESS_REFUSED WEB to w1-locked from 0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd (tier EndpointRule(Node { node: "0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd" }))
2026-09-09T05:22:01.0376110Z WEB_STREAM: stream=1 origin=0cef34af5151a8a2b534f6d29a543b3ad35e2c1a4b2fe91bf60f027232a135fd outcome=refused reason=ACCESS_DENIED: WEB: the owning node's access rules refuse this request
2026-09-09T05:22:01.0377150Z WEB_STREAM: stream=3 origin=cff883e9631ed98668018c069cbc265a97217b942f5276e4f19ac15bdef5f349 outcome=sent status=200 bytes=35 path=/kitsubito/f/open.md
2026-09-09T05:22:01.0378069Z WEB_STREAM: stream=2 origin=6609402a9c961143ce5f22103095f4c4a2ff884dccdfc49b7e88b12c29c7d04e outcome=sent status=206 bytes=4 path=/kitsubito/f/open.md
2026-09-09T05:22:01.0378859Z CONN_LIFECYCLE: conn=8 role=brain wall_ms=1788931321037 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0379921Z CONN_LIFECYCLE: conn=8 role=brain wall_ms=1788931321037 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0381258Z CONN_LIFECYCLE: conn=7 role=brain wall_ms=1788931321037 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0382421Z CONN_LIFECYCLE: conn=9 role=brain wall_ms=1788931321037 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0383665Z CONN_LIFECYCLE: conn=7 role=brain wall_ms=1788931321037 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0385066Z CONN_LIFECYCLE: conn=9 role=brain wall_ms=1788931321037 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0386289Z CONN_LIFECYCLE: conn=6 role=brain wall_ms=1788931321038 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0387299Z CONN_LIFECYCLE: conn=10 role=brain wall_ms=1788931321038 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0388468Z CONN_LIFECYCLE: conn=6 role=brain wall_ms=1788931321038 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0389943Z CONN_LIFECYCLE: conn=10 role=brain wall_ms=1788931321038 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0391120Z CONN_LIFECYCLE: conn=11 role=brain wall_ms=1788931321038 mono_ms=6904 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:01.0392000Z CONN_LIFECYCLE: conn=11 role=brain wall_ms=1788931321038 mono_ms=6904 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:04.1396218Z CONN_LIFECYCLE: conn=2 role=brain wall_ms=1788931324139 mono_ms=10005 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:04.1398481Z TWOHOST-WEB role B: A not ready for the message yet (broker QUIC op exceeded the 10s bound (peer unresponsive) — failing as an ordinary per-peer error)
2026-09-09T05:22:06.1635325Z CONN_LIFECYCLE: conn=2 role=brain stream-subscriber stream=4 wall_ms=1788931326163 mono_ms=12029 event=stream-sub-attach stream=4 from_seq=0 [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.3399797Z F1_ARM=vi: waiting for A's ingress to register on its user's authority
2026-09-09T05:22:06.3400455Z TWOHOST-WEB role B: the user's message was ADMITTED by A (Spooled)
2026-09-09T05:22:06.3401873Z CONN_LIFECYCLE: conn=2 role=brain stream-subscriber stream=4 wall_ms=1788931326339 mono_ms=12206 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4148899Z CONN_LIFECYCLE: conn=12 role=brain wall_ms=1788931326414 mono_ms=12281 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4150969Z CONN_LIFECYCLE: conn=12 role=brain wall_ms=1788931326414 mono_ms=12281 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4198129Z WEB_SERVE_FOR: stream=5 origin=a819392896e272997e2841a1d6db43fb8a4aae82b3983456c8791770f9d3c083 outcome=registered name=quoted-by-the-user.md ttl_ms=86400000 audience=w2-helper-audience
2026-09-09T05:22:06.4200784Z CONN_LIFECYCLE: conn=13 role=brain wall_ms=1788931326419 mono_ms=12286 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4203237Z CONN_LIFECYCLE: conn=13 role=brain wall_ms=1788931326420 mono_ms=12286 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4206773Z CONN_LIFECYCLE: conn=14 role=brain wall_ms=1788931326420 mono_ms=12287 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.4207811Z CONN_LIFECYCLE: conn=14 role=brain wall_ms=1788931326420 mono_ms=12287 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.8413481Z TWOHOST OK: role B registered quoted-by-the-user.md for w2-helper-audience on its user's authority in 501 ms (kind=File ttl=Some(86400000) origin=Some("BCDFGH23"))
2026-09-09T05:22:06.8415726Z TWOHOST OK: role B serving open.md + locked.md (waiting for 5 served cells)
2026-09-09T05:22:06.8416734Z TWOHOST-WEB role B: served cell 1/5
2026-09-09T05:22:06.8417420Z TWOHOST-WEB role B: served cell 2/5
2026-09-09T05:22:06.8418093Z TWOHOST-WEB role B: served cell 3/5
2026-09-09T05:22:06.8418737Z TWOHOST-WEB role B: served cell 4/5
2026-09-09T05:22:06.9741073Z CONN_LIFECYCLE: conn=15 role=brain wall_ms=1788931326973 mono_ms=12840 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9743032Z CONN_LIFECYCLE: conn=15 role=brain wall_ms=1788931326974 mono_ms=12840 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9752750Z WEB_STREAM: stream=6 origin=a819392896e272997e2841a1d6db43fb8a4aae82b3983456c8791770f9d3c083 outcome=sent status=200 bytes=31 path=/kitsubito/f/quoted-by-the-user.md
2026-09-09T05:22:06.9761527Z CONN_LIFECYCLE: conn=16 role=brain wall_ms=1788931326975 mono_ms=12842 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9764485Z CONN_LIFECYCLE: conn=16 role=brain wall_ms=1788931326976 mono_ms=12842 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9767429Z CONN_LIFECYCLE: conn=17 role=brain wall_ms=1788931326976 mono_ms=12843 event=write-start [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9768541Z CONN_LIFECYCLE: conn=17 role=brain wall_ms=1788931326976 mono_ms=12843 event=transport-close poisoned=false timed_out=false [REQ-CONN-POISON-ATTRIBUTION]
2026-09-09T05:22:06.9917788Z TWOHOST-WEB role B: served cell 5/5
2026-09-09T05:22:16.9923486Z TWOHOST OK: role B served all 5 requester cells
2026-09-09T05:22:16.9982022Z test two_host_web_role_b ... ok
2026-09-09T05:22:16.9982786Z 
2026-09-09T05:22:16.9983310Z test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 22.89s
2026-09-09T05:22:16.9984062Z 
